View Issue Details

IDProjectCategoryView StatusLast Update
0001263T99X171.00 SKB EagleVOCpublic2021-12-23 17:49
Reporter(ALTech) Younkwang Jung Assigned To(SW) Kerwin Chen Due Date
PrioritynormalSeveritys2-severeReproducibilityhave not tried
Status closedResolutionunable to reproduce 
Summary0001263: [Smart3][BTF][VoC] No Audio after Power ON (Audio is OK after changing TV volume) Smart3(15.520.8-0004)
DescriptionHi Kerwin

No Audio after Power ON , Audio was OK after changing TV volume
FW : v15.520.8-004
TV : JVC / LT-40MK570

jira link is below :
https://jira.skbroadband.com/browse/BTVV-1841
I attached all the files of this Jira. ( BTVV-1841.zip )

It was not reproduced with the same issue as this field VoC,
but a similar issue was found during another test.

The test sequence is as follows.
FW : v15.524.4g
TV : ANAM FHD TV

1. Using the hdmitest.sh provided by Amlogc.
    BFX-AT100:/btv_home/tmp # ./hdmitest.sh 1080p60hz 444,8bit 1 1
    ...
    Test 1080p60hz with hdcp 1
    hdcp 14 AuthRst 1
    ...

2. Test result
    - Video is ok , but it is in No Audio output state.
    - Audio output is OK when TV volume adjustment or HDMI hot plug is applied
      attached log file : NO_Audio_0526.zip
      The script execution time above is between 15:59:04 and 16:00:01.

Please check this issue

Thank you
YK.Jung
TagsNo tags attached.
Attach Tags

Users monitoring this issue

User List (ALTech) SY Yoon , (SW) Brent Choi , (SW) Jerry Lin

Activities

(ALTech) Younkwang Jung

2021-06-04 09:47

developer  

BTVV-1841.zip (4,434,737 bytes)

(SW) Kerwin Chen

2021-06-04 11:47

developer   ~0007364

Hi Mr. YK Jung,

Do you know when "NO Audio" occurs ?
In "NO_Audio_0526.zip", I see many messages about "mute", but I can't tell if it is a problem or not.
I think player mute audio during "16:00:19" ~ "16:03:37"
Thanks !
================= logcat.log =======================
...
05-26 16:00:19.026 4839 4839 D UIControlManager: needMutePlayer | true
05-26 16:00:19.026 4839 4839 I PlayerManager: setMuteMainPlayer: true
05-26 16:00:19.027 4839 4839 D UIControlManager: needMutePlayer | true
05-26 16:00:19.101 4839 4839 W ContextImpl: Calling a method in the system process without a qualified user: android.app.ContextImpl.sendBroadcast:1045 ntent.ContextWrapper.sendBroadcast:448 .google.tv.player.PlayerManager.setState:2237 com.skb.google.tv.player.PlayerManager.setMuteMainPlayer:3078 ogle.tv.controller.UIControlManager.checkUI:972
..
05-26 16:00:19.170 3557 6820 I LivePlayerRenderer_0: [setMuteEnable:430] setMuteEnable:1, mPassthroughSetting=0
05-26 16:00:19.170 3557 6820 I MiniAudioSink: setmute:1
05-26 16:00:21.866 4839 4839 D UIControlManager: needMutePlayer | true
05-26 16:00:21.866 4839 4839 I PlayerManager: setMuteMainPlayer: true
05-26 16:00:21.866 4839 4839 D UIControlManager: needMutePlayer | true
05-26 16:00:25.436 4839 4839 D UIControlManager: needMutePlayer | true
05-26 16:00:25.436 4839 4839 I PlayerManager: setMuteMainPlayer: true
05-26 16:00:25.437 4839 4839 D UIControlManager: needMutePlayer | true
05-26 16:00:34.361 4839 4839 D UIControlManager: needMutePlayer | true
05-26 16:00:34.361 4839 4839 I PlayerManager: setMuteMainPlayer: true
05-26 16:00:34.362 4839 4839 D UIControlManager: needMutePlayer | true
05-26 16:00:38.330 4839 4839 D UIControlManager: needMutePlayer | true
05-26 16:00:38.330 4839 4839 I PlayerManager: setMuteMainPlayer: true
05-26 16:00:38.331 4839 4839 D UIControlManager: needMutePlayer | true
05-26 16:00:53.064 3529 3529 D AF::TrackHandle: OpPlayAudio: track:55 usage:13 not muted
05-26 16:03:33.674 4839 4839 D UIControlManager: needMutePlayer | true
05-26 16:03:33.674 4839 4839 I PlayerManager: setMuteMainPlayer: true
05-26 16:03:33.675 4839 4839 D UIControlManager: needMutePlayer | true
05-26 16:03:33.721 4839 4839 W ContextImpl: Calling a method in the system process without a qualified user: android.app.ContextImpl.sendBroadcast:1045 ntent.ContextWrapper.sendBroadcast:448 .google.tv.player.PlayerManager.setState:2237 com.skb.google.tv.player.PlayerManager.setMuteMainPlayer:3078 ogle.tv.controller.UIControlManager.checkUI:972
05-26 16:03:34.469 4839 4839 D UIControlManager: needMutePlayer | true
05-26 16:03:34.470 4839 4839 I PlayerManager: setMuteMainPlayer: true
05-26 16:03:34.470 4839 4839 D UIControlManager: needMutePlayer | true
05-26 16:03:36.189 4839 4839 I PlayerManager: setMuteMainPlayer: false
05-26 16:03:36.414 3557 3769 I BOXKEY-STDVB: [dvb_device_open] mute in
05-26 16:03:36.414 3557 3769 E halMediaPlayer: BTF|AVP_SetAudioMute|396|IN| player=ee302150, mute=1
05-26 16:03:36.414 3557 3769 D AmlHalPlayerImpl_0: [setMuteEnable:1405] >
05-26 16:03:36.414 3557 3769 I LivePlayer_0: setMuteEnable:1
05-26 16:03:36.414 3557 3769 E halMediaPlayer: BTF|AVP_SetAudioMute|408|OUT|
05-26 16:03:36.414 3557 3769 I BOXKEY-STDVB: [dvb_device_open] mute out
05-26 16:03:36.416 3557 7877 I LivePlayerRenderer_0: [setMuteEnable:430] setMuteEnable:0, mPassthroughSetting=-1
05-26 16:03:36.417 3557 7877 I LivePlayer_0: setAudioMute,1642, mMuteAudio=1, mAudioPausedByPassth=0, mAudioPausedByClient=0
05-26 16:03:36.417 3557 7877 I LivePlayerRenderer_0: [setMuteEnable:430] setMuteEnable:1, mPassthroughSetting=-1
05-26 16:03:36.419 3557 7877 I LivePlayer_0: setAudioMute,1642, mMuteAudio=1, mAudioPausedByPassth=0, mAudioPausedByClient=0
05-26 16:03:36.419 3557 7877 I LivePlayerRenderer_0: [setMuteEnable:430] setMuteEnable:1, mPassthroughSetting=0
....
05-26 16:03:36.679 3557 7877 D BOXKEY-MEDIA: [playerEventCallback] AVP_FIRST_AUDIO_FRAME_DECODED..., player->save_mute_enable:0 @@INFO:IptvSocPlayer.cpp(997)
05-26 16:03:36.687 3557 7881 I MiniAudioSink: setmute:1
05-26 16:03:37.164 3557 3788 D BOXKEY-MEDIA: [INFO] [onFirstIframeTimeEvent][0] player.save_mute_enable : 0 @@INFO:IptvSocPlayer.cpp(5044)
05-26 16:03:37.164 3557 3788 D BOXKEY-MEDIA: [iframe] unmute in @@INFO:IptvSocPlayer.cpp(5056)
05-26 16:03:37.164 3557 3788 D BOXKEY-STDVB: [iptvimpl 05/26 16:03:37:164][3788] [impl.c][DEVICE 0] ST_AudioMute START, value : unmute, enableGlobalMute : 0
05-26 16:03:37.164 3557 3788 E halMediaPlayer: BTF|AVP_SetAudioMute|396|IN| player=ee302150, mute=0
05-26 16:03:37.164 3557 3788 D AmlHalPlayerImpl_0: [setMuteEnable:1405] >
05-26 16:03:37.164 3557 3788 I LivePlayer_0: setMuteEnable:0
05-26 16:03:37.165 3557 3788 E halMediaPlayer: BTF|AVP_SetAudioMute|408|OUT|
05-26 16:03:37.165 3557 3788 D BOXKEY-STDVB: [iptvimpl 05/26 16:03:37:165][3788] [impl.c][DEVICE 0] ST_AudioMute END [-1]
05-26 16:03:37.165 3557 7877 I LivePlayer_0: setAudioMute,1642, mMuteAudio=0, mAudioPausedByPassth=0, mAudioPausedByClient=0
05-26 16:03:37.165 3557 3788 D BOXKEY-MEDIA: [iframe] unmute out @@INFO:IptvSocPlayer.cpp(5058)
05-26 16:03:37.165 3557 7877 I LivePlayerRenderer_0: [setMuteEnable:430] setMuteEnable:0, mPassthroughSetting=0
05-26 16:03:37.165 3557 7877 I MiniAudioSink: setmute:0
...

(ALTech) Younkwang Jung

2021-06-04 12:14

developer   ~0007366

Hi Kerwin

It's strange that when user change the volume of the TV ( not STB ) , the sound is output from the STB.
This issue is being reported in the field.

Is there any additional information that can be debugged in the part related to this issue or discussed further with Amlogic?

Thank you
YK.Jung

(SW) Kerwin Chen

2021-06-04 15:32

developer   ~0007368

Hi Mr. YK Jung,

The only possible reason I know is CEC.
I also see CEC is enabled in "NO_Audio_0526.zip".
This test was at ALT's office.
I think you can disable CEC both on TV and STB and try if issue exists.
Thanks !

....
05-26 15:57:31.253 3557 3557 I SocControl.018: [setCEC] called onOff(1)
05-26 15:57:31.253 3557 3557 D Display : ++DISPLAY_CEC_SetEnable :cec_enable(1), gautopoweroff(1)
05-26 15:57:31.253 3557 3557 D hal_display: hal_display:HAL_DISPLAY_CEC_SetEnable
....

(ALTech) SY Yoon

2021-06-10 13:45

developer   ~0007428

Hi Kerwin Chen,

I set both TV and STB to disable CEC in my office. And test again.
There is no change.
I attached log.

Thanks
No_audio_0610.zip (175,356 bytes)

(SW) Kerwin Chen

2021-06-10 14:56

developer   ~0007430

Hi Mr. SY Yoon,

Could you let me know the time of no audio issue happening ?
And the time to change TV volume ?
Thanks !

(ALTech) SY Yoon

2021-06-10 16:14

developer   ~0007431

Hi Kerwin,

I didn't check what you want.
So I tested again.
I input hdmitest.sh test command around 17:06:15.
I attached hdmitest.sh file.
And I change the TV Volume set around 17:06:33.
If you need more information, please let me know.
No_Audio_0610_2.zip (535,171 bytes)
hdmitest.sh (2,390 bytes)   
#!/system/bin/sh

# This shell is used for testing HDCP 1.4 / 2.2
# Example:
#    Test HDCP1.4 under 1080p 59.94hz 444,8bit
#    hdmitest.sh 1080p60hz 444,8bit 1 1

if [ $# -le 0 ]; then
	echo "Usage: hdmitest.sh OUTPUTMODE ATTR HDCPMODE"
	echo "       OUTPUTMODE can be 480p60hz, 720p50hz, 1080p60hz, etc"
	echo "       ATTR can be 444,8bit, 422,8bit, etc"
	echo "       HDCPMODE only can be 1 or 2 or 0"
	exit 0
fi

outputmode=$1
attr=$2
hdcpmode=$3

res="0 0 1919 1079"

if [ $hdcpmode -eq 1 ] || [ $hdcpmode -eq 2 ]; then
	echo "Test $outputmode with hdcp $hdcpmode"
else
	echo "Test $outputmode without hdcp"
fi

# CONSTANT SYSFS NODE
AVMUTE=/sys/class/amhdmitx/amhdmitx0/avmute
HDCP_CTRL=/sys/class/amhdmitx/amhdmitx0/hdcp_ctrl
HDCP_MODE=/sys/class/amhdmitx/amhdmitx0/hdcp_mode
AUTH_RST=/sys/module/hdmitx20/parameters/hdmi_authenticated
PHY=/sys/class/amhdmitx/amhdmitx0/phy
ATTR=/sys/class/amhdmitx/amhdmitx0/attr
DISP_MODE=/sys/class/display/mode
WIND_AXIS=/sys/class/graphics/fb0/window_axis
FREE_SCALE=/sys/class/graphics/fb0/free_scale
# check Auth Result
hdcp_auth_result () {
	echo 'hdcp' `cat $HDCP_MODE` 'AuthRst' `cat $AUTH_RST`
	return
}

# start HDCP Auth
hdcp_start () {
# stop hdcp before start hdcp
	hdcp_stop
	if [ $hdcpmode -eq 1 ]; then
		echo 1 > $HDCP_MODE
		sleep 1
		echo -1 > $AVMUTE
	fi
	if [ $hdcpmode -eq 2 ]; then
		echo 2 > $HDCP_MODE
		sleep 0.01
		hdcp_tx22 -f /vendor/etc/firmware/firmware.le &
		sleep 2
		echo -1 > $AVMUTE
	fi
	sleep 1
}

# stop HDCP Auth
hdcp_stop () {
	sleep 0.05
	echo stop14 > $HDCP_CTRL
	echo stop22 > $HDCP_CTRL
}

hdmi_set_outputmode() {
	echo 1 > $AVMUTE
	sleep 0.05
	stop hdcp_tx22
	echo 0 > $PHY
	hdcp_stop
	echo null > $DISP_MODE
	echo $attr > $ATTR
	echo $outputmode > $DISP_MODE
	echo $res > $WIND_AXIS
	echo 0x10001 > $FREE_SCALE
	# If there is no any HDCP, then clear AVMUTE directly
	if [ $hdcpmode -eq 0 ]; then
		echo -1 > $AVMUTE
	fi
	return
}

if [[ "$outputmode" == 480* ]]; then
	res="0 0 719 479"
elif [[ "$outputmode" == 576* ]]; then
	res="0 0 719 479"
elif [[ "$outputmode" == 720* ]]; then
	res="0 0 1279 719"
elif [[ "$outputmode" == 1080* ]]; then
	res="0 0 1919 1079"
elif [[ "$outputmode" == 2160* ]]; then
	res="0 0 3839 2159"
elif [[ "$outputmode" == smpte* ]]; then
	res="0 0 4095 2159"
else
	res="0 0 1919 1079"
fi

hdmi_set_outputmode
if [ $hdcpmode -ne 0 ]; then
	hdcp_start
	hdcp_auth_result
fi
hdmitest.sh (2,390 bytes)   

(SW) Kerwin Chen

2021-06-11 10:17

developer   ~0007438

Hi Mr. SY Yoon,

I have another question about the test.
Do "No Audio" issue happen after you run AML's script ?

(ALTech) SY Yoon

2021-06-11 11:18

developer   ~0007439

Hi Mr. Kerwin Chen,

Yes. you're right.
Our test sequence is
1. STB Turn on - There is no issue. Audio is OK
2. AML Script run - No audio.
3. TV Volume change with TV RCU - Audio is OK.

(SW) Kerwin Chen

2021-06-12 09:40

developer   ~0007449

Hi Mr. SY Yoon,

From the log, I only find 1 unusual message about HDMI.
"mSignalHpd" is updated to '0' while script set HDMI resolution.
But this parameter is not updated to '1' even after you change TV volume to make audio coming back.
There is no special audio message while you adjust TV volume.
And the log is quite different with "NO_Audio_0526.zip" which is tested on Android 10
So I can't make sure if it is root cause or not.

I don't know the purpose to run the script because sysfs commands are issued to kernel driver directly.
Some services in Android Framework may not be awared of the state changes.
In the 'hdmitest.sh', it sets disable HDMI PHY in hdmi_set_outputmode().
But the script doesn't enable HDMI PHY again.
I suggest to have AML debug on this issue since script is provided by them.
Thanks !

(ALTech) SY Yoon

2021-06-14 08:35

developer   ~0007451

Hi Kerwin Chen,

This test script is the script that makes color depth to 10 bit.
It was our default from 15.522.x.
So I would like to apply this setting to STB that has 15.513.x and 15.520.8 version.

In case of ANAM TV that I tested, we can reproduce this issue. The sequence is
1. STB Power on,
2. No Audio
3. Change TV Volume, then we can hear the audio from TV
    We cannot hear TV sound when we change STB volume.
But we cannot reproduced right now. We don't know why its status is changed.
But we can reproduce after AML test script now.
If you make some addition change on the script, then please let us know.
If I input parameter change to 444 to 420, 8bit to 10bit, then there is no issue.
And this issue didn't happen in other TV that we have.

(SW) Kerwin Chen

2021-12-23 17:49

developer   ~0009080

please reopen if issues still exists

Issue History

Date Modified Username Field Change
2021-06-04 09:47 (ALTech) Younkwang Jung New Issue
2021-06-04 09:47 (ALTech) Younkwang Jung Status new => assigned
2021-06-04 09:47 (ALTech) Younkwang Jung Assigned To => (SW) Kerwin Chen
2021-06-04 09:47 (ALTech) Younkwang Jung File Added: BTVV-1841.zip
2021-06-04 11:47 (SW) Kerwin Chen Note Added: 0007364
2021-06-04 12:14 (ALTech) Younkwang Jung Note Added: 0007366
2021-06-04 15:32 (SW) Kerwin Chen Note Added: 0007368
2021-06-10 13:43 (ALTech) SY Yoon Issue Monitored: (ALTech) SY Yoon
2021-06-10 13:45 (ALTech) SY Yoon File Added: No_audio_0610.zip
2021-06-10 13:45 (ALTech) SY Yoon Note Added: 0007428
2021-06-10 13:54 (ALTech) Younkwang Jung Issue Monitored: (SW) Brent Choi
2021-06-10 13:54 (ALTech) Younkwang Jung Issue Monitored: (SW) Jerry Lin
2021-06-10 14:56 (SW) Kerwin Chen Note Added: 0007430
2021-06-10 16:14 (ALTech) SY Yoon File Added: No_Audio_0610_2.zip
2021-06-10 16:14 (ALTech) SY Yoon File Added: hdmitest.sh
2021-06-10 16:14 (ALTech) SY Yoon Note Added: 0007431
2021-06-11 10:17 (SW) Kerwin Chen Note Added: 0007438
2021-06-11 11:18 (ALTech) SY Yoon Note Added: 0007439
2021-06-12 09:40 (SW) Kerwin Chen Note Added: 0007449
2021-06-14 08:35 (ALTech) SY Yoon Note Added: 0007451
2021-12-23 17:49 (SW) Kerwin Chen Status assigned => closed
2021-12-23 17:49 (SW) Kerwin Chen Resolution open => unable to reproduce
2021-12-23 17:49 (SW) Kerwin Chen Note Added: 0009080